**** dT 0.000 * top TEST ../../../../bin/vinyltest/tests/o00004.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:64032 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/tests **** top macro def tmpdir=/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7 **** top macro def vtcid=vtc.378372.7df7b0d7 **** dT 0.001 ** top === vtest "Sending proxy headers via health probes" * top VTEST Sending proxy headers via health probes ** top === vinyl v2 -arg "-b '${bad_backend}'" -start **** dT 0.011 ** v2 Launch *** v2 CMD: cd ${pwd} && exec vinyld -d -n /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 48653' -P /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2/vinyld.pid -p vmod_path=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -b '127.0.0.1:64032' *** v2 CMD: cd /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 48653' -P /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2/vinyld.pid -p vmod_path=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -b '127.0.0.1:64032' **** dT 0.014 *** v2 PID: 378382 **** v2 macro def v2_pid=378382 **** v2 macro def v2_name=/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2 **** dT 0.707 *** v2 debug|Warnings: *** v2 debug|Message from VCC-compiler: *** v2 debug|Security: proc_setid missing, uid/gid unchanged *** v2 debug|Message from C-compiler: *** v2 debug|Security: proc_setid missing, uid/gid unchanged *** v2 debug|Message from dlopen: *** v2 debug|Security: proc_setid missing, uid/gid unchanged *** v2 debug| **** dT 0.717 *** v2 debug|Warning: mlock() of VSM failed: Not owner (1 *** v2 debug|) **** dT 0.720 *** v2 debug|Debug: Version: vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v2 debug| *** v2 debug|Debug: *** v2 debug| *** v2 debug|Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug| *** v2 debug|200 292 *** v2 debug|----------------------------- *** v2 debug|Vinyl Cache CLI 1.0 *** v2 debug|----------------------------- *** v2 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v2 debug| *** v2 debug|Type 'help' for command list. *** v2 debug|Type 'quit' to close CLI session. *** v2 debug|Type 'start' to launch worker process. *** v2 debug| **** dT 0.818 **** v2 CLIPOLL 1 0x1 0x0 0x0 *** v2 CLI connection fd = 4 **** dT 0.819 *** v2 CLI RX 107 **** v2 CLI RX|cizvcwftlwthbaknrllhszwjzllcwuxw **** v2 CLI RX| **** v2 CLI RX|Authentication required. **** v2 CLI TX|auth a846b6b5379b155d2fe1ebf19761740c393786b60a4fc381200046b268bc1eb8 *** v2 CLI RX 200 **** v2 CLI RX|----------------------------- **** v2 CLI RX|Vinyl Cache CLI 1.0 **** v2 CLI RX|----------------------------- **** v2 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v2 CLI RX|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 **** v2 CLI RX| **** v2 CLI RX|Type 'help' for command list. **** v2 CLI RX|Type 'quit' to close CLI session. **** v2 CLI RX|Type 'start' to launch worker process. **** dT 0.820 ** v2 Start **** v2 CLI TX|start **** dT 0.832 *** v2 debug|Debug: *** v2 debug|Child (378418) Started **** dT 0.860 *** v2 debug|Error: Child failed on launch *** v2 debug| *** v2 debug|Error: Child (378418) said Child starts *** v2 debug|Error: *** v2 debug| *** v2 debug|Child (378418) said notice:\tlibumem was already found to be loaded *** v2 debug| *** v2 debug|Error: *** v2 debug| *** v2 debug|Child (378418) said Security: proc_setid missing, uid/gid unchanged *** v2 debug| **** dT 0.920 **** v2 vsl| 0 Debug - Create worker thread failed 11 Resource temporarily unavailable **** dT 0.960 *** v2 debug|Error: Child (378418) died signal=6 *** v2 debug| **** dT 0.961 *** v2 debug|Error: *** v2 debug|Child (378418) Panic at: Mon, 16 Mar 2026 21:53:55 GMT *** v2 debug|Wrong turn at ../../../../bin/vinyld/cache/cache_wrk.c:121: *** v2 debug|pthread_create(thr, ((void *)0), wrk_bgthread, bt) failed *** v2 debug|version = vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4, vrt api = 23.0 *** v2 debug|ident = -jsolaris,-sdefault,-sdefault,-hcritbit,ports *** v2 debug|now = 5910111.325670 (mono), 1773698035.312165 (real) *** v2 debug|Backtrace: *** v2 debug| 53a868: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'vbt_execinfo+0x36 [0x53a868] *** v2 debug| 53aac7: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VBT_format+0x2c [0x53aac7] *** v2 debug| 48868c: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_backtrace+0x3a [0x48868c] *** v2 debug| 488a3f: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_ic+0x2da [0x488a3f] *** v2 debug| 539cf5: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VAS_Fail+0x4 *** v2 debug|f [0x539cf5] *** v2 debug| 4b88aa: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'WRK_BgThread+0xda [0x4b88aa] *** v2 debug| 46b289: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'EXP_Init+0xf6 [0x46b289] *** v2 debug| 481c2f: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'child_main+0x36a [0x481c2f] *** v2 debug| 4db6d4: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'mgt_launch_child+0x3ef [0x4db6d4] *** v2 debug| 4dc58d: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'mch_cli_server_start+0x3a [0x4dc58d] *** v2 debug| 53bc9d: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'cls_dispatch+0x37a [0x53bc9d] *** v2 debug| 53bf84: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'cls_exec+0x2d9 [0x53bf84] *** v2 debug| 53c526: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build *** v2 debug|/sub/bin/vinyld/vinyld'cls_feed+0x43e [0x53c526] *** v2 debug| 53d815: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VCLS_Poll+0x300 [0x53d815] *** v2 debug| 4dd443: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'mgt_cli_callback2+0x2e [0x4dd443] *** v2 debug| 5403af: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VEV_Once+0x4e1 [0x5403af] *** v2 debug| 53fcd8: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VEV_Loop+0xd1 [0x53fcd8] *** v2 debug| 4e401f: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'main+0x18da [0x4e401f] *** v2 debug| 444f37: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'_start_crt+0x87 [0x444f37] *** v2 debug| 444e98: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'_start+0x18 [0x444e98] *** v2 debug|errno = 11 (Resource temporarily unavailable) *** v2 debug|argv = { *** v2 debug| [0] = \"vin *** v2 debug|yld\", *** v2 debug| [1] = \"-d\", *** v2 debug| [2] = \"-n\", *** v2 debug| [3] = \"/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2\", *** v2 debug| [4] = \"-i\", *** v2 debug| [5] = \"v2\", *** v2 debug| [6] = \"-l\", *** v2 debug| [7] = \"2m\", *** v2 debug| [8] = \"-p\", *** v2 debug| [9] = \"auto_restart=off\", *** v2 debug| [10] = \"-p\", *** v2 debug| [11] = \"syslog_cli_traffic=off\", *** v2 debug| [12] = \"-p\", *** v2 debug| [13] = \"thread_pool_min=10\", *** v2 debug| [14] = \"-p\", *** v2 debug| [15] = \"debug=+vtc_mode\", *** v2 debug| [16] = \"-p\", *** v2 debug| [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\", *** v2 debug| [18] = \"-p\", *** v2 debug| [19] = \"h2_initial_window_size=1m\", *** v2 debug| [20] = \"-p\", *** v2 debug| [21] = \"h2_rx_window_low_water=64k\", *** v2 debug| [22] = \"-a\", *** v2 debug| [23] = \"127.0.0.1:0\", *** v2 debug| [24] = \"-M\", *** v2 debug| [25] = \"127.0.0.1 48653\", *** v2 debug| [26] = \"-P\", *** v2 debug| [27] = \"/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2/vinyld.pid\", *** v2 debug| [28] = \"-p\", *** v2 debug| [29] = \"vmod_path=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs:\", *** v2 debug| [30] = \"-b\", *** v2 debug| [31] = \"127.0.0.1:64032\", *** v2 debug|} *** v2 debug|pthread.self = 1 *** v2 debug|pthread.name = (cache-main) *** v2 debug|thr.req = NULL *** v2 debug|thr.busyobj = NULL *** v2 debug|thr.worke *** v2 CLI RX 400 **** v2 CLI RX|Child failed on launch *** v2 debug|r = NULL *** v2 debug|vmods = { *** v2 debug|}, *** v2 debug|pools = { *** v2 debug| pool = fffffc7feedb7b90 { *** v2 debug| nidle = 9, *** v2 debug| nthr = 10, *** v2 debug| lqueue = 0 *** v2 debug| }, *** v2 debug|}, *** v2 debug| *** v2 debug| *** v2 debug|Debug: Child cleanup complete **** dT 0.971 ---- v2 CLI start command failed: 400 Child failed on launch * top RESETTING after ../../../../bin/vinyltest/tests/o00004.vtc ** v2 Wait **** v2 CLI TX|panic.show *** v2 CLI RX 200 **** v2 CLI RX|Child (378418) Panic at: Mon, 16 Mar 2026 21:53:55 GMT **** v2 CLI RX|Wrong turn at ../../../../bin/vinyld/cache/cache_wrk.c:121: **** v2 CLI RX|pthread_create(thr, ((void *)0), wrk_bgthread, bt) failed **** v2 CLI RX|version = vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4, vrt api = 23.0 **** v2 CLI RX|ident = -jsolaris,-sdefault,-sdefault,-hcritbit,ports **** v2 CLI RX|now = 5910111.325670 (mono), 1773698035.312165 (real) **** v2 CLI RX|Backtrace: **** v2 CLI RX| 53a868: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'vbt_execinfo+0x36 [0x53a868] **** v2 CLI RX| 53aac7: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VBT_format+0x2c [0x53aac7] **** v2 CLI RX| 48868c: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_backtrace+0x3a [0x48868c] **** v2 CLI RX| 488a3f: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_ic+0x2da [0x488a3f] **** v2 CLI RX| 539cf5: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VAS_Fail+0x4f [0x539cf5] **** v2 CLI RX| 4b88aa: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'WRK_BgThread+0xda [0x4b88aa] **** v2 CLI RX| 46b289: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'EXP_Init+0xf6 [0x46b289] **** v2 CLI RX| 481c2f: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'child_main+0x36a [0x481c2f] **** v2 CLI RX| 4db6d4: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'mgt_launch_child+0x3ef [0x4db6d4] **** v2 CLI RX| 4dc58d: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'mch_cli_server_start+0x3a [0x4dc58d] **** v2 CLI RX| 53bc9d: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'cls_dispatch+0x37a [0x53bc9d] **** v2 CLI RX| 53bf84: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'cls_exec+0x2d9 [0x53bf84] **** v2 CLI RX| 53c526: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'cls_feed+0x43e [0x53c526] **** v2 CLI RX| 53d815: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VCLS_Poll+0x300 [0x53d815] **** v2 CLI RX| 4dd443: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'mgt_cli_callback2+0x2e [0x4dd443] **** v2 CLI RX| 5403af: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VEV_Once+0x4e1 [0x5403af] **** v2 CLI RX| 53fcd8: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VEV_Loop+0xd1 [0x53fcd8] **** v2 CLI RX| 4e401f: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'main+0x18da [0x4e401f] **** v2 CLI RX| 444f37: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'_start_crt+0x87 [0x444f37] **** v2 CLI RX| 444e98: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'_start+0x18 [0x444e98] **** v2 CLI RX|errno = 11 (Resource temporarily unavailable) **** v2 CLI RX|argv = { **** v2 CLI RX| [0] = \"vinyld\", **** v2 CLI RX| [1] = \"-d\", **** v2 CLI RX| [2] = \"-n\", **** v2 CLI RX| [3] = \"/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2\", **** v2 CLI RX| [4] = \"-i\", **** v2 CLI RX| [5] = \"v2\", **** v2 CLI RX| [6] = \"-l\", **** v2 CLI RX| [7] = \"2m\", **** v2 CLI RX| [8] = \"-p\", **** v2 CLI RX| [9] = \"auto_restart=off\", **** v2 CLI RX| [10] = \"-p\", **** v2 CLI RX| [11] = \"syslog_cli_traffic=off\", **** v2 CLI RX| [12] = \"-p\", **** v2 CLI RX| [13] = \"thread_pool_min=10\", **** v2 CLI RX| [14] = \"-p\", **** v2 CLI RX| [15] = \"debug=+vtc_mode\", **** v2 CLI RX| [16] = \"-p\", **** v2 CLI RX| [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\", **** v2 CLI RX| [18] = \"-p\", **** v2 CLI RX| [19] = \"h2_initial_window_size=1m\", **** v2 CLI RX| [20] = \"-p\", **** v2 CLI RX| [21] = \"h2_rx_window_low_water=64k\", **** v2 CLI RX| [22] = \"-a\", **** v2 CLI RX| [23] = \"127.0.0.1:0\", **** v2 CLI RX| [24] = \"-M\", **** v2 CLI RX| [25] = \"127.0.0.1 48653\", **** v2 CLI RX| [26] = \"-P\", **** v2 CLI RX| [27] = \"/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.378372.7df7b0d7/v2/vinyld.pid\", **** v2 CLI RX| [28] = \"-p\", **** v2 CLI RX| [29] = \"vmod_path=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs:\", **** v2 CLI RX| [30] = \"-b\", **** v2 CLI RX| [31] = \"127.0.0.1:64032\", **** v2 CLI RX|} **** v2 CLI RX|pthread.self = 1 **** v2 CLI RX|pthread.name = (cache-main) **** v2 CLI RX|thr.req = NULL **** v2 CLI RX|thr.busyobj = NULL **** v2 CLI RX|thr.worker = NULL **** v2 CLI RX|vmods = { **** v2 CLI RX|}, **** v2 CLI RX|pools = { **** v2 CLI RX| pool = fffffc7feedb7b90 { **** v2 CLI RX| nidle = 9, **** v2 CLI RX| nthr = 10, **** v2 CLI RX| lqueue = 0 **** v2 CLI RX| }, **** v2 CLI RX|}, **** v2 CLI RX| **** v2 CLI RX| **** dT 0.981 ---- v2 Unexpected panic * top failure during reset # top TEST ../../../../bin/vinyltest/tests/o00004.vtc FAILED (0.987) exit=2 FAIL tests/o00004.vtc (exit status: 2)